[INFO] cloning repository https://github.com/rbnyng/floatlambda
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/rbnyng/floatlambda" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbnyng%2Ffloatlambda", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbnyng%2Ffloatlambda'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 2bc968e18c1fad7aa5b0eb45ad338364af27ec75
[INFO] testing rbnyng/floatlambda against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Frbnyng%2Ffloatlambda" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/rbnyng/floatlambda
[INFO] finished tweaking git repo https://github.com/rbnyng/floatlambda
[INFO] tweaked toml for git repo https://github.com/rbnyng/floatlambda written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/rbnyng/floatlambda on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/rbnyng/floatlambda already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded clap v4.5.41
[INFO] [stderr]   Downloaded clap_builder v4.5.41
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 88f78bfd9745bc51793e69a7c3448293c8fe301138aaa2a701f18cd844db030f
[INFO] running `Command { std: "docker" "start" "-a" "88f78bfd9745bc51793e69a7c3448293c8fe301138aaa2a701f18cd844db030f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "88f78bfd9745bc51793e69a7c3448293c8fe301138aaa2a701f18cd844db030f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "88f78bfd9745bc51793e69a7c3448293c8fe301138aaa2a701f18cd844db030f", kill_on_drop: false }`
[INFO] [stdout] 88f78bfd9745bc51793e69a7c3448293c8fe301138aaa2a701f18cd844db030f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8869c0c5cfd64019c7bb9f64010a5af5a9b9bf94e4590a0530311beb2088feef
[INFO] running `Command { std: "docker" "start" "-a" "8869c0c5cfd64019c7bb9f64010a5af5a9b9bf94e4590a0530311beb2088feef", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.174
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling anstyle v1.0.11
[INFO] [stderr]    Compiling anstyle-query v1.1.3
[INFO] [stderr]    Compiling clap_lex v0.7.5
[INFO] [stderr]    Compiling zerocopy v0.8.26
[INFO] [stderr]    Compiling proc-macro2 v1.0.95
[INFO] [stderr]    Compiling anstream v0.6.19
[INFO] [stderr]    Compiling clap_builder v4.5.41
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.104
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling clap_derive v4.5.41
[INFO] [stderr]    Compiling clap v4.5.41
[INFO] [stderr]    Compiling float_lambda v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.40s
[INFO] running `Command { std: "docker" "inspect" "8869c0c5cfd64019c7bb9f64010a5af5a9b9bf94e4590a0530311beb2088feef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8869c0c5cfd64019c7bb9f64010a5af5a9b9bf94e4590a0530311beb2088feef", kill_on_drop: false }`
[INFO] [stdout] 8869c0c5cfd64019c7bb9f64010a5af5a9b9bf94e4590a0530311beb2088feef
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48e4729aeddc512d8435a9305daa42145b346b900f3fa6f675eb18587b303a52
[INFO] running `Command { std: "docker" "start" "-a" "48e4729aeddc512d8435a9305daa42145b346b900f3fa6f675eb18587b303a52", kill_on_drop: false }`
[INFO] [stderr]    Compiling float_lambda v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.12s
[INFO] running `Command { std: "docker" "inspect" "48e4729aeddc512d8435a9305daa42145b346b900f3fa6f675eb18587b303a52", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48e4729aeddc512d8435a9305daa42145b346b900f3fa6f675eb18587b303a52", kill_on_drop: false }`
[INFO] [stdout] 48e4729aeddc512d8435a9305daa42145b346b900f3fa6f675eb18587b303a52
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] fc766d77e2977fdccb0fec80f98cb533abee0d56d30fa0ea8df83716e1906026
[INFO] running `Command { std: "docker" "start" "-a" "fc766d77e2977fdccb0fec80f98cb533abee0d56d30fa0ea8df83716e1906026", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/float_lambda-b9c5fcb5e772ed6f)
[INFO] [stdout] 
[INFO] [stdout] running 107 tests
[INFO] [stdout] test math::tests::test_basic_trig ... ok
[INFO] [stdout] test math::tests::test_hyperbolic ... ok
[INFO] [stdout] test math::tests::test_random_functions ... ok
[INFO] [stdout] test math::tests::test_utility_functions ... ok
[INFO] [stdout] test ml::tensor::tests::test_tensor_new ... ok
[INFO] [stdout] test tests::test_application_error_types ... ok
[INFO] [stdout] test tests::test_application_of_non_function ... ok
[INFO] [stdout] test ml::autodiff::tests::test_grad_of_matmul ... ok
[INFO] [stdout] test math::tests::test_constants ... ok
[INFO] [stdout] test math::tests::test_special_functions ... ok
[INFO] [stdout] test tests::test_binary_operation ... ok
[INFO] [stdout] test tests::test_blended_conditional ... ok
[INFO] [stdout] test tests::test_basic_derivatives ... ok
[INFO] [stdout] test math::tests::test_edge_cases ... ok
[INFO] [stdout] test tests::test_builtin_map ... ok
[INFO] [stdout] test tests::test_calculus_composition ... ok
[INFO] [stdout] test ml::autodiff::tests::test_grad_of_add ... ok
[INFO] [stdout] test math::tests::test_power_functions ... ok
[INFO] [stdout] test tests::test_closure_captures_correct_env ... ok
[INFO] [stdout] test tests::test_complex_expression_with_let ... ok
[INFO] [stdout] test tests::test_calculus_with_fuzzy_functions ... ok
[INFO] [stdout] test tests::test_can_cons_anything ... ok
[INFO] [stdout] test tests::test_car_cdr_type_errors ... ok
[INFO] [stdout] test tests::test_currying_builtin ... ok
[INFO] [stdout] test tests::test_complex_fuzzy_expression ... ok
[INFO] [stdout] test tests::test_calculus_edge_cases ... ok
[INFO] [stdout] test tests::test_builtin_length ... ok
[INFO] [stdout] test tests::test_builtin_filter ... ok
[INFO] [stdout] test tests::test_float_eval ... ok
[INFO] [stdout] test tests::test_emergent_property_nan_propagation ... ok
[INFO] [stdout] test tests::test_fuzzy_eq ... ok
[INFO] [stdout] test tests::test_builtin_foldl ... ok
[INFO] [stdout] test tests::test_fuzzy_logic ... ok
[INFO] [stdout] test tests::test_gc_reclaims_part_of_a_structure ... ok
[INFO] [stdout] test tests::test_higher_order_function_const ... ok
[INFO] [stdout] test tests::test_higher_order_function_apply ... ok
[INFO] [stdout] test tests::test_improved_fuzzy_eq ... ok
[INFO] [stdout] test tests::test_higher_order_fold_left_aka_reduce ... ok
[INFO] [stdout] test tests::test_let_rec_requires_lambda ... ok
[INFO] [stdout] test tests::test_if_statement_with_function_as_condition ... ok
[INFO] [stdout] test tests::test_lambda_application ... ok
[INFO] [stdout] test tests::test_higher_order_map ... ok
[INFO] [stdout] test tests::test_let_binding_simple ... ok
[INFO] [stdout] test tests::test_higher_order_filter ... ok
[INFO] [stdout] test tests::test_non_nil_terminated_list ... ok
[INFO] [stdout] test tests::test_nan_handling ... ok
[INFO] [stdout] test tests::test_gc_on_long_list ... ok
[INFO] [stdout] test tests::test_higher_order_calculus ... ok
[INFO] [stdout] test tests::test_list_of_lists ... ok
[INFO] [stdout] test tests::test_parse_builtin ... ok
[INFO] [stdout] test tests::test_parse_float ... ok
[INFO] [stdout] test tests::test_parse_if ... ok
[INFO] [stdout] test math::tests::test_inverse_trig ... ok
[INFO] [stdout] test tests::test_parse_lambda ... ok
[INFO] [stdout] test tests::test_lone_lambda_is_a_value ... ok
[INFO] [stdout] test tests::test_parse_variable ... ok
[INFO] [stdout] test tests::test_parse_application ... ok
[INFO] [stdout] test tests::test_robust_parser_keywords ... ok
[INFO] [stdout] test tests::test_truth_decay_concept ... ok
[INFO] [stdout] test tests::test_var_eval ... ok
[INFO] [stdout] test tests::test_unbound_variable_in_deeper_expression ... ok
[INFO] [stdout] test tests::test_strlen_on_string_list ... ok
[INFO] [stdout] test vm::hof_natives::tests::test_native_length ... ok
[INFO] [stdout] test vm::hof_natives::tests::test_native_foldl ... ok
[INFO] [stdout] test vm::hof_natives::tests::test_native_map ... ok
[INFO] [stdout] test vm::natives_test::test_native_e ... ok
[INFO] [stdout] test vm::natives_test::test_native_is_nan ... ok
[INFO] [stdout] test tests::test_string_as_list_manipulation ... ok
[INFO] [stdout] test vm::chunk::tests::test_write_and_add_constant ... ok
[INFO] [stdout] test vm::natives_test::test_native_pi ... ok
[INFO] [stdout] test vm::natives_test::test_native_pow ... ok
[INFO] [stdout] test vm::natives_test::test_native_print_error_non_list ... ok
[INFO] [stdout] test vm::natives_test::test_native_print_success ... ok
[INFO] [stdout] test vm::natives_test::test_native_random ... ok
[INFO] [stdout] test vm::natives_test::test_native_random_range ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_if_as_expression ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_data_structure_errors ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_data_structures ... ok
[INFO] [stdout] test vm::natives_test::test_native_sin ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_diff_constant_function ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_diff_identity_function ... ok
[INFO] [stdout] test vm::natives_test::test_native_sqrt ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_if_statement ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_fundamental_theorem_of_calculus ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_fuzzy_eq_on_pointers_is_nan ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_fuzzy_equality_double_equals ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_diff_x_cubed ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_higher_order_diff ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_diff_x_squared ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_function_behavior ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_globals ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_native_functions ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_native_print ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_integrate_x ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_simple_expressions ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_simple_function_call ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_nested_conditionals ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_integrate_constant ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_nested_blending ... ok
[INFO] [stdout] test tests::test_simple_curried_integration ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_integrate_x_squared ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_strict_equality_eq ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_recursion ... ok
[INFO] [stdout] test tests::test_basic_integrals ... ok
[INFO] [stdout] test tests::test_curried_integration ... ok
[INFO] [stdout] test vm::vm_test::vm_tests::test_vm_tail_call_optimization ... ok
[INFO] [stdout] test tests::test_fundamental_theorem_of_calculus ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 107 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/float_lambda-51bccbf195affb64)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cnn_test.rs (/opt/rustwide/target/debug/deps/cnn_test-b241d5dc8c198685)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test cnn_test::cnn_training_pipeline_loss_decreases ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gc_tests.rs (/opt/rustwide/target/debug/deps/gc_tests-102832bf1c6847dc)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test gc_completeness_tests::test_simple_unrooted_object ... ok
[INFO] [stdout] test gc_correctness_tests::test_transitive_reachability ... ok
[INFO] [stdout] test gc_correctness_tests::test_simple_root_preservation ... ok
[INFO] [stdout] test gc_completeness_tests::test_reclaiming_part_of_a_structure ... ok
[INFO] [stdout] test gc_correctness_tests::test_closure_environment_preservation ... ok
[INFO] [stdout] test gc_vm_integration_tests::test_gc_roots_the_vm_call_frames ... ok
[INFO] [stdout] test gc_vm_integration_tests::test_gc_roots_the_vm_stack ... ok
[INFO] [stdout] test gc_completeness_tests::test_circular_references ... ok
[INFO] [stdout] test gc_vm_integration_tests::test_gc_does_not_corrupt_vm_state_during_execution ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/string_tests.rs (/opt/rustwide/target/debug/deps/string_tests-17214d7679b239a2)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test string_edge_cases::test_string_as_function_argument ... ok
[INFO] [stdout] test string_edge_cases::test_string_with_numbers ... ok
[INFO] [stdout] test string_edge_cases::test_string_with_special_chars ... ok
[INFO] [stdout] test string_edge_cases::test_unicode_characters ... ok
[INFO] [stdout] test string_integration_tests::test_string_with_list_operations ... ok
[INFO] [stdout] test string_literal_tests::test_empty_string ... ok
[INFO] [stdout] test string_edge_cases::test_string_in_expressions ... ok
[INFO] [stdout] test string_function_tests::test_string_concatenation ... ok
[INFO] [stdout] test string_function_tests::test_string_case_conversion ... ok
[INFO] [stdout] test string_literal_tests::test_multi_char_string ... ok
[INFO] [stdout] test string_literal_tests::test_single_char_string ... ok
[INFO] [stdout] test string_literal_tests::test_invalid_escape_sequences ... ok
[INFO] [stdout] test string_integration_tests::test_string_in_conditionals ... ok
[INFO] [stdout] test string_literal_tests::test_escape_sequences ... ok
[INFO] [stdout] test string_function_tests::test_string_length_prelude ... ok
[INFO] [stdout] test string_function_tests::test_substring ... ok
[INFO] [stdout] test string_function_tests::test_string_starts_with ... ok
[INFO] [stdout] test string_literal_tests::test_unterminated_string ... ok
[INFO] [stdout] test string_literal_tests::test_string_length ... ok
[INFO] [stdout] test string_performance_tests::test_string_with_many_escapes ... ok
[INFO] [stdout] test string_edge_cases::test_nested_string_operations ... ok
[INFO] [stdout] test string_integration_tests::test_string_split_and_join ... ok
[INFO] [stdout] test string_literal_tests::test_string_with_spaces ... ok
[INFO] [stdout] test string_integration_tests::test_string_with_filter ... ok
[INFO] [stdout] test string_performance_tests::test_long_string_parsing ... ok
[INFO] [stdout] test string_integration_tests::test_string_with_map ... ok
[INFO] [stdout] test string_function_tests::test_string_ends_with ... ok
[INFO] [stdout] test string_edge_cases::test_mixed_case_string ... ok
[INFO] [stdout] test string_performance_tests::test_repeated_string_operations ... ok
[INFO] [stdout] test string_function_tests::test_string_equality ... ok
[INFO] [stdout] test string_function_tests::test_string_contains ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tco_tests.rs (/opt/rustwide/target/debug/deps/tco_tests-6746a3e9854ebfa0)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test tco_benchmarks::benchmark_tail_recursion_vs_iteration ... ignored
[INFO] [stdout] test tco_benchmarks::stress_test_extreme_depth ... ignored
[INFO] [stdout] test tco_tests::test_simple_tail_recursive_factorial ... ok
[INFO] [stdout] test tco_tests::test_non_tail_recursion_still_works ... ok
[INFO] [stdout] test tco_tests::test_tail_recursive_fibonacci ... ok
[INFO] [stdout] test tco_tests::test_tail_recursion_with_complex_expressions ... ok
[INFO] [stdout] test tco_tests::test_mixed_tail_and_non_tail ... ok
[INFO] [stdout] test tco_tests::test_tail_calls_in_let_expressions ... ok
[INFO] [stdout] test tco_tests::test_tail_recursive_countdown ... ok
[INFO] [stdout] test tco_tests::test_performance_comparison ... FAILED
[INFO] [stdout] test tco_tests::test_tail_recursive_list_sum ... ok
[INFO] [stdout] test tco_tests::test_mutually_recursive_even_odd ... ok
[INFO] [stdout] test tco_tests::test_nested_tail_calls ... ok
[INFO] [stdout] test tco_tests::test_tail_call_through_higher_order_function ... ok
[INFO] [stdout] test tco_tests::test_very_deep_tail_recursion ... ok
[INFO] [stdout] test tco_tests::test_stack_safety ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tco_tests::test_performance_comparison stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tco_tests::test_performance_comparison' (174) panicked at tests/tco_tests.rs:208:9:
[INFO] [stdout] Tail recursion too slow: 145ms
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5bcb1fbab728 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5bcb1fbab728 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5bcb1fbab728 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5bcb1fbab728 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5bcb1fbc0d0a - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5bcb1fbc0d0a - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5bcb1fbaff42 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5bcb1fbaff42 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5bcb1fb8a6a6 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5bcb1fb8a6a6 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5bcb1fba3c49 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5bcb1fab33cc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5bcb1fab33cc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5bcb1fba3e02 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5bcb1fba3e02 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5bcb1fb8a762 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5bcb1fb7f8e9 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5bcb1fb8b4fd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5bcb1fbc148c - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5bcb1faa4987 - tco_tests[8abe66693b84e14b]::tco_tests::test_performance_comparison
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/tco_tests.rs:208:9
[INFO] [stdout]   20:     0x5bcb1faa3297 - tco_tests[8abe66693b84e14b]::tco_tests::test_performance_comparison::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/tco_tests.rs:191:37
[INFO] [stdout]   21:     0x5bcb1faa6146 - <tco_tests[8abe66693b84e14b]::tco_tests::test_performance_comparison::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5bcb1faa64ab - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5bcb1faa64ab - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x5bcb1fab3d25 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x5bcb1fab3d25 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5bcb1fab3d25 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5bcb1fab3d25 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5bcb1fab3d25 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5bcb1fab3d25 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x5bcb1fab3d25 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x5bcb1faae884 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x5bcb1faae884 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5bcb1fab6da2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5bcb1fab6da2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5bcb1fab6da2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5bcb1fab6da2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5bcb1fab6da2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5bcb1fab6da2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5bcb1fab6da2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5bcb1fbab08f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   41:     0x5bcb1fbab08f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x768406122aa4 - <unknown>
[INFO] [stdout]   43:     0x7684061afa64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tco_tests::test_performance_comparison
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 13 passed; 1 failed; 2 ignored; 0 measured; 0 filtered out; finished in 1.98s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test tco_tests`
[INFO] running `Command { std: "docker" "inspect" "fc766d77e2977fdccb0fec80f98cb533abee0d56d30fa0ea8df83716e1906026", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fc766d77e2977fdccb0fec80f98cb533abee0d56d30fa0ea8df83716e1906026", kill_on_drop: false }`
[INFO] [stdout] fc766d77e2977fdccb0fec80f98cb533abee0d56d30fa0ea8df83716e1906026
